[INFO] cloning repository https://github.com/christhomas/rust-fs-ext4
[INFO] running `Command { std: "git" "-c" "credential.helper=" "-c" "credential.helper=/workspace/cargo-home/bin/git-credential-null" "clone" "--bare" "https://github.com/christhomas/rust-fs-ext4" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fchristhomas%2Frust-fs-ext4", kill_on_drop: false }`
[INFO] [stderr] Cloning into bare repository '/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fchristhomas%2Frust-fs-ext4'...
[INFO] running `Command { std: "git" "rev-parse" "HEAD", kill_on_drop: false }`
[INFO] [stdout] f39e760872e42f03649514b303f61ef05b8393ae
[INFO] testing christhomas/rust-fs-ext4 against master#f9988fefd3add01f414f52b414308e7872622fee for pr-155114
[INFO] running `Command { std: "git" "clone" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fchristhomas%2Frust-fs-ext4" "/workspace/builds/worker-0-tc1/source", kill_on_drop: false }`
[INFO] [stderr] Cloning into '/workspace/builds/worker-0-tc1/source'...
[INFO] [stderr] done.
[INFO] removed /workspace/builds/worker-0-tc1/source/rust-toolchain.toml
[INFO] started tweaking git repo https://github.com/christhomas/rust-fs-ext4
[INFO] finished tweaking git repo https://github.com/christhomas/rust-fs-ext4
[INFO] tweaked toml for git repo https://github.com/christhomas/rust-fs-ext4 written to /workspace/builds/worker-0-tc1/source/Cargo.toml
[INFO] validating manifest of git repo https://github.com/christhomas/rust-fs-ext4 on toolchain f9988fefd3add01f414f52b414308e7872622fee
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+f9988fefd3add01f414f52b414308e7872622fee" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] crate git repo https://github.com/christhomas/rust-fs-ext4 already has a lockfile, it will not be regenerated
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+f9988fefd3add01f414f52b414308e7872622fee" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-0-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-0-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+f9988fefd3add01f414f52b414308e7872622fee" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] 79ca85ddb1edf51928fa939de29e61c3eab45066dfa6572ee50b1c37ee536139
[INFO] running `Command { std: "docker" "start" "-a" "79ca85ddb1edf51928fa939de29e61c3eab45066dfa6572ee50b1c37ee536139", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "79ca85ddb1edf51928fa939de29e61c3eab45066dfa6572ee50b1c37ee536139", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "79ca85ddb1edf51928fa939de29e61c3eab45066dfa6572ee50b1c37ee536139", kill_on_drop: false }`
[INFO] [stdout] 79ca85ddb1edf51928fa939de29e61c3eab45066dfa6572ee50b1c37ee536139
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-0-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-0-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+f9988fefd3add01f414f52b414308e7872622fee" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] e491925d14ef7c5c20dc98473dcc5011a24e0740e0c89b44ff0fb0d179f8dfb2
[INFO] running `Command { std: "docker" "start" "-a" "e491925d14ef7c5c20dc98473dcc5011a24e0740e0c89b44ff0fb0d179f8dfb2", kill_on_drop: false }`
[INFO] [stderr]    Compiling semver v1.0.28
[INFO] [stderr]    Compiling bitflags v2.11.1
[INFO] [stderr]    Compiling rustc_version v0.4.1
[INFO] [stderr]    Compiling crc32c v0.6.8
[INFO] [stderr]    Compiling fs-ext4 v0.1.3 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `dev` profile [optimized + debuginfo] target(s) in 11.67s
[INFO] running `Command { std: "docker" "inspect" "e491925d14ef7c5c20dc98473dcc5011a24e0740e0c89b44ff0fb0d179f8dfb2", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "e491925d14ef7c5c20dc98473dcc5011a24e0740e0c89b44ff0fb0d179f8dfb2", kill_on_drop: false }`
[INFO] [stdout] e491925d14ef7c5c20dc98473dcc5011a24e0740e0c89b44ff0fb0d179f8dfb2
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-0-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-0-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+f9988fefd3add01f414f52b414308e7872622fee" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 9a239f97edc54afa22be843da8fb7e7db8136316534a047995bcfb9205454148
[INFO] running `Command { std: "docker" "start" "-a" "9a239f97edc54afa22be843da8fb7e7db8136316534a047995bcfb9205454148", kill_on_drop: false }`
[INFO] [stderr]    Compiling hex v0.4.3
[INFO] [stderr]    Compiling fs-ext4 v0.1.3 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `test` profile [optimized + debuginfo] target(s) in 29.68s
[INFO] running `Command { std: "docker" "inspect" "9a239f97edc54afa22be843da8fb7e7db8136316534a047995bcfb9205454148", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "9a239f97edc54afa22be843da8fb7e7db8136316534a047995bcfb9205454148", kill_on_drop: false }`
[INFO] [stdout] 9a239f97edc54afa22be843da8fb7e7db8136316534a047995bcfb9205454148
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-0-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-0-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+f9988fefd3add01f414f52b414308e7872622fee" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] 8c6cbe9109e2c4a28e43ff1cce8d646812cd6b750109a9b8dff55d15c9bd53be
[INFO] running `Command { std: "docker" "start" "-a" "8c6cbe9109e2c4a28e43ff1cce8d646812cd6b750109a9b8dff55d15c9bd53be", kill_on_drop: false }`
[INFO] [stderr]     Finished `test` profile [optimized + debuginfo] target(s) in 0.08s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/fs_ext4-3274364e02d5fc93)
[INFO] [stdout] 
[INFO] [stdout] running 186 tests
[INFO] [stdout] test acl::tests::decodes_minimal_acl ... ok
[INFO] [stdout] test acl::tests::decodes_named_user_and_group ... ok
[INFO] [stdout] test acl::tests::rejects_truncated_full_entry ... ok
[INFO] [stdout] test acl::tests::rejects_unknown_tag ... ok
[INFO] [stdout] test acl::tests::rejects_wrong_version ... ok
[INFO] [stdout] test acl::tests::tag_has_id_matches_spec ... ok
[INFO] [stdout] test acl::tests::xattr_names_correct ... ok
[INFO] [stdout] test alloc::tests::apply_bitmap_write_sets_and_clears_bits ... ok
[INFO] [stdout] test alloc::tests::block_allocation_honours_block_uninit_flag ... ok
[INFO] [stdout] test alloc::tests::block_allocation_skips_full_group ... ok
[INFO] [stdout] test alloc::tests::block_allocation_uses_first_group_with_room ... ok
[INFO] [stdout] test alloc::tests::find_first_free_all_ones_in_range ... ok
[INFO] [stdout] test alloc::tests::find_first_free_respects_max_bits_mid_word ... ok
[INFO] [stdout] test alloc::tests::find_first_free_walks_bits ... ok
[INFO] [stdout] test alloc::tests::find_first_free_word_aligned_fast_path ... ok
[INFO] [stdout] test alloc::tests::find_free_run_handles_gaps ... ok
[INFO] [stdout] test block_cache::tests::multi_block_read_bypasses_cache ... ok
[INFO] [stdout] test block_cache::tests::write_invalidates_cache_entry ... ok
[INFO] [stdout] test block_io::tests::caching_device_invalidates_on_write ... ok
[INFO] [stdout] test block_io::tests::caching_device_write_invalidates_overlapping_but_not_distant ... ok
[INFO] [stdout] test block_io::tests::caching_device_evicts_lru ... ok
[INFO] [stdout] test block_io::tests::caching_device_bypasses_non_aligned_reads ... ok
[INFO] [stdout] test block_io::tests::caching_device_caches_repeated_reads ... ok
[INFO] [stdout] test block_io::tests::callback_device_without_writer_rejects_writes ... ok
[INFO] [stdout] test alloc::tests::find_free_run_rejects_too_short ... ok
[INFO] [stdout] test alloc::tests::find_free_run_exact_fit ... ok
[INFO] [stdout] test alloc::tests::inode_allocation_returns_one_based_number ... ok
[INFO] [stdout] test alloc::tests::orlov_prefers_group_with_fewer_dirs ... ok
[INFO] [stdout] test casefold::tests::casefold_low_bit_is_zero ... ok
[INFO] [stdout] test casefold::tests::fold_ascii_uppercase ... ok
[INFO] [stdout] test casefold::tests::siphash_rfc_zero_key_empty_matches_impl_specific ... ok
[INFO] [stdout] test checksum::tests::crc_helpers_are_deterministic ... ok
[INFO] [stdout] test block_io::tests::file_device_ro_write_rejected ... ok
[INFO] [stdout] test checksum::tests::dir_tail_rejects_too_short_block_when_enabled ... ok
[INFO] [stdout] test checksum::tests::dir_tail_roundtrip_and_tamper ... ok
[INFO] [stdout] test checksum::tests::disabled_when_feature_off ... ok
[INFO] [stdout] test checksum::tests::extent_tail_excludes_only_last_4_bytes ... ok
[INFO] [stdout] test checksum::tests::patch_extent_tail_disabled_is_noop ... ok
[INFO] [stdout] test checksum::tests::patch_extent_tail_is_verify_inverse ... ok
[INFO] [stdout] test checksum::tests::verifies_real_bgd ... ok
[INFO] [stdout] test checksum::tests::verifies_real_dir_tail ... ok
[INFO] [stdout] test checksum::tests::verifies_real_extent_tail ... ok
[INFO] [stdout] test checksum::tests::verifies_real_inode ... ok
[INFO] [stdout] test checksum::tests::verifies_real_superblock ... ok
[INFO] [stdout] test dir::tests::add_entry_respects_reserved_tail ... ok
[INFO] [stdout] test alloc::tests::find_first_free_unaligned_start_matches_per_bit ... ok
[INFO] [stdout] test dir::tests::add_entry_reuses_tombstone_in_middle ... ok
[INFO] [stdout] test casefold::tests::casefold_hash_is_case_insensitive ... ok
[INFO] [stdout] test dir::tests::add_entry_splits_terminal_record ... ok
[INFO] [stdout] test casefold::tests::casefold_hash_differs_on_different_names ... ok
[INFO] [stdout] test dir::tests::add_then_remove_round_trips ... ok
[INFO] [stdout] test dir::tests::parses_dot_dotdot_hello ... ok
[INFO] [stdout] test acl::tests::rejects_truncated_header ... ok
[INFO] [stdout] test block_cache::tests::lru_evicts_oldest_when_capacity_exceeded ... ok
[INFO] [stdout] test block_cache::tests::lru_keeps_recently_touched_block_alive ... ok
[INFO] [stdout] test dir::tests::rec_len_matches_kernel_layout ... ok
[INFO] [stdout] test dir::tests::rejects_bad_rec_len ... ok
[INFO] [stdout] test block_io::tests::file_device_rw_round_trip ... ok
[INFO] [stdout] test dir::tests::remove_entry_coalesces_into_previous ... ok
[INFO] [stdout] test dir::tests::remove_entry_tombstones_first_slot ... ok
[INFO] [stdout] test extent::tests::ext4_basic_root_inode_has_extent ... ok
[INFO] [stdout] test dir::tests::remove_entry_reports_missing ... ok
[INFO] [stdout] test extent::tests::leaf_lookup_finds_mapping ... ok
[INFO] [stdout] test extent::tests::rejects_bad_magic ... ok
[INFO] [stdout] test extent::tests::rejects_entries_gt_max ... ok
[INFO] [stdout] test extent::tests::rejects_impossible_depth ... ok
[INFO] [stdout] test extent::tests::uninitialized_extent_maps_to_none ... ok
[INFO] [stdout] test extent_mut::tests::free_extent_emits_physical_run ... ok
[INFO] [stdout] test extent_mut::tests::insert_merges_left_contiguous ... ok
[INFO] [stdout] test extent_mut::tests::insert_rejects_overlap ... ok
[INFO] [stdout] test extent_mut::tests::merge_noop_when_not_contiguous ... ok
[INFO] [stdout] test ea_inode::tests::ea_inode_flag_bit_value ... ok
[INFO] [stdout] test extent_mut::tests::multi_level_tree_refused_cleanly ... ok
[INFO] [stdout] test extent_mut::tests::insert_does_not_merge_across_uninit_boundary ... ok
[INFO] [stdout] test extent_mut::tests::insert_fails_when_root_full_and_no_merge ... ok
[INFO] [stdout] test extent_mut::tests::insert_into_empty_root ... ok
[INFO] [stdout] test ea_inode::tests::zero_inode_is_rejected ... ok
[INFO] [stdout] test alloc::tests::inode_allocation_dir_bumps_used_dirs ... ok
[INFO] [stdout] test extent_mut::tests::promote_leaf_merges_when_contiguous_with_new ... ok
[INFO] [stdout] test extent_mut::tests::promote_leaf_moves_entries_into_new_leaf_block ... ok
[INFO] [stdout] test extent_mut::tests::promote_leaf_rejects_overlap ... ok
[INFO] [stdout] test extent_mut::tests::split_extent_at_midpoint ... ok
[INFO] [stdout] test extent_mut::tests::split_rejects_boundary_point ... ok
[INFO] [stdout] test file_mut::tests::shrink_splits_straddling_extent ... ok
[INFO] [stdout] test file_mut::tests::shrink_rejects_grow_direction ... ok
[INFO] [stdout] test file_mut::tests::split_aligned_single_block ... ok
[INFO] [stdout] test file_mut::tests::grow_returns_delta ... ok
[INFO] [stdout] test file_mut::tests::split_crosses_block_boundary ... ok
[INFO] [stdout] test file_mut::tests::split_empty_data_returns_empty ... ok
[INFO] [stdout] test file_mut::tests::write_range_full_blocks ... ok
[INFO] [stdout] test file_mut::tests::write_range_partial_head_and_tail ... ok
[INFO] [stdout] test file_mut::tests::write_range_zero_length_returns_none ... ok
[INFO] [stdout] test hash::tests::empty_name_does_not_panic ... ok
[INFO] [stdout] test hash::tests::legacy_distinguishes_names ... ok
[INFO] [stdout] test hash::tests::half_md4_distinguishes_names ... ok
[INFO] [stdout] test hash::tests::low_bit_always_clear ... ok
[INFO] [stdout] test hash::tests::signed_vs_unsigned_diverge_on_high_bytes ... ok
[INFO] [stdout] test hash::tests::tea_distinguishes_names ... ok
[INFO] [stdout] test file_mut::tests::grow_rejects_shrink ... ok
[INFO] [stdout] test file_mut::tests::shrink_drops_whole_tail_extent ... ok
[INFO] [stdout] test hash::tests::version_recognition_round_trip ... ok
[INFO] [stdout] test file_mut::tests::shrink_to_zero_frees_all ... ok
[INFO] [stdout] test htree::tests::parses_root_entries_count_2 ... ok
[INFO] [stdout] test htree::tests::find_entry_for_hash_picks_correct_slot ... ok
[INFO] [stdout] test htree::tests::parses_root_info_correctly ... ok
[INFO] [stdout] test htree_mut::tests::dx_entry_insert_rejects_duplicate_hash ... ok
[INFO] [stdout] test htree_mut::tests::dx_entry_insert_rejects_full_node ... ok
[INFO] [stdout] test htree_mut::tests::dx_entry_insert_root_preserves_sort ... ok
[INFO] [stdout] test htree_mut::tests::leaf_split_balances_entries ... ok
[INFO] [stdout] test htree_mut::tests::leaf_split_rejects_trivial ... ok
[INFO] [stdout] test indirect::tests::beyond_triple_indirect_is_corrupt ... ok
[INFO] [stdout] test indirect::tests::direct_pointer_lookup ... ok
[INFO] [stdout] test indirect::tests::double_indirect_lookup ... ok
[INFO] [stdout] test htree::tests::lookup_leaf_zero_indirect_picks_correct_block ... ok
[INFO] [stdout] test file_mut::tests::split_sub_block_write ... ok
[INFO] [stdout] test indirect::tests::single_indirect_lookup ... ok
[INFO] [stdout] test indirect::tests::triple_indirect_lookup ... ok
[INFO] [stdout] test indirect::tests::single_indirect_zero_pointer_is_hole ... ok
[INFO] [stdout] test indirect_mut::tests::collect_for_free_skips_sparse_holes ... ok
[INFO] [stdout] test indirect_mut::tests::collect_for_free_walks_tree_and_coalesces_runs ... ok
[INFO] [stdout] test extent_mut::tests::promote_leaf_preserves_generation ... ok
[INFO] [stdout] test indirect_mut::tests::plan_contiguous_fills_all_direct ... ok
[INFO] [stdout] test indirect_mut::tests::plan_contiguous_fills_single_indirect_exactly ... ok
[INFO] [stdout] test indirect_mut::tests::plan_contiguous_into_single_indirect ... ok
[INFO] [stdout] test indirect_mut::tests::count_indirect_blocks_matches_plan_contiguous ... ok
[INFO] [stdout] test indirect_mut::tests::plan_contiguous_into_double_indirect ... ok
[INFO] [stdout] test indirect_mut::tests::round_trip_plan_then_collect ... ok
[INFO] [stdout] test jbd2::tests::dirty_sb_is_not_clean ... ok
[INFO] [stdout] test jbd2::tests::parses_v2_superblock ... ok
[INFO] [stdout] test jbd2::tests::rejects_bad_magic ... ok
[INFO] [stdout] test jbd2::tests::rejects_non_superblock_block_type ... ok
[INFO] [stdout] test jbd2::tests::v1_leaves_feature_fields_zero ... ok
[INFO] [stdout] test journal::tests::advance_wraps_around_the_log ... ok
[INFO] [stdout] test journal::tests::clean_journal_produces_empty_plan ... ok
[INFO] [stdout] test journal::tests::filter_revoked_drops_older_writes ... ok
[INFO] [stdout] test journal::tests::parse_v2_tags_without_bit64 ... ok
[INFO] [stdout] test journal::tests::revoke_records_scale_with_r_count ... ok
[INFO] [stdout] test indirect_mut::tests::plan_contiguous_into_triple_indirect ... ok
[INFO] [stdout] test block_io::tests::best_effort_falls_back_to_ro ... FAILED
[INFO] [stdout] test journal_apply::tests::clean_summary ... ok
[INFO] [stdout] test journal_apply::tests::empty_plan_applies_zero_blocks ... ok
[INFO] [stdout] test journal::tests::try_parse_header_accepts_valid ... ok
[INFO] [stdout] test journal::tests::try_parse_header_rejects_bad_magic ... ok
[INFO] [stdout] test journal_apply::tests::replay_entry_structure_stable ... ok
[INFO] [stdout] test journal_apply::tests::replay_summary_mirrors_jsb ... ok
[INFO] [stdout] test journal_writer::tests::empty_transaction_is_no_op ... ok
[INFO] [stdout] test journal_writer::tests::open_returns_none_when_no_journal ... ok
[INFO] [stdout] test path::tests::missing_path_returns_not_found ... ok
[INFO] [stdout] test path::tests::root_resolves_to_inode_2 ... ok
[INFO] [stdout] test transaction::tests::descriptor_tags_are_big_endian_and_have_last_flag ... ok
[INFO] [stdout] test transaction::tests::empty_transaction_yields_only_commit ... ok
[INFO] [stdout] test transaction::tests::jsb_constants_available ... ok
[INFO] [stdout] test transaction::tests::parsed_descriptor_matches_original_tags ... ok
[INFO] [stdout] test transaction::tests::revoke_block_serialises_count_and_records ... ok
[INFO] [stdout] test casefold::tests::fold_preserves_non_ascii ... ok
[INFO] [stdout] test casefold::tests::fold_empty_name ... ok
[INFO] [stdout] test transaction::tests::round_trip_through_walk_style_parsing ... ok
[INFO] [stdout] test transaction::tests::two_block_txn_layout_is_desc_then_data_then_commit ... ok
[INFO] [stdout] test transaction::tests::v3_tag_layout_32bit_flags ... ok
[INFO] [stdout] test transaction::tests::wrong_block_size_payload_is_rejected ... ok
[INFO] [stdout] test verify::tests::claimed_bitmap_set_get_count ... ok
[INFO] [stdout] test xattr::tests::prefixes_known ... ok
[INFO] [stdout] test xattr::tests::remove_in_inode_missing_magic_is_not_found ... ok
[INFO] [stdout] test xattr::tests::remove_in_inode_roundtrips_one_of_two ... ok
[INFO] [stdout] test xattr::tests::remove_in_inode_unknown_prefix_is_einval ... ok
[INFO] [stdout] test xattr::tests::set_in_inode_enospc_on_overflow ... ok
[INFO] [stdout] test xattr::tests::set_in_inode_inserts_new_into_empty_region ... ok
[INFO] [stdout] test xattr::tests::set_in_inode_preserves_other_entries ... ok
[INFO] [stdout] test xattr::tests::set_in_inode_replaces_existing_value ... ok
[INFO] [stdout] test xattr::tests::set_in_inode_unknown_prefix_is_einval ... ok
[INFO] [stdout] test xattr::tests::split_qualified_name_roundtrip ... ok
[INFO] [stdout] test verify::tests::report_default_is_clean ... ok
[INFO] [stdout] test htree::tests::rejects_nonzero_reserved ... ok
[INFO] [stdout] test htree::tests::rejects_bad_hash_version ... ok
[INFO] [stdout] test path::tests::non_dir_component_returns_not_a_directory ... ok
[INFO] [stdout] test extent_mut::tests::merge_adjacent_contiguous_pair ... ok
[INFO] [stdout] test extent_mut::tests::insert_sorted_ordering ... ok
[INFO] [stdout] test casefold::tests::siphash_empty_input ... ok
[INFO] [stdout] test dir::tests::add_entry_enospc_when_no_room ... ok
[INFO] [stdout] test indirect_mut::tests::plan_contiguous_direct_only ... ok
[INFO] [stdout] test htree_mut::tests::dx_entry_insert_at_end ... ok
[INFO] [stdout] test indirect_mut::tests::collect_for_free_zero_size_yields_nothing ... ok
[INFO] [stdout] test path::tests::split_path_basic ... ok
[INFO] [stdout] test xattr::tests::remove_in_inode_returns_not_found_for_missing ... ok
[INFO] [stdout] test block_cache::tests::second_read_of_same_block_is_a_cache_hit ... ok
[INFO] [stderr] error: test failed, to rerun pass `--lib`
[INFO] [stdout] test indirect_mut::tests::plan_contiguous_rejects_overflow_beyond_triple ... ok
[INFO] [stdout] 
[INFO] [stdout] failures:
[INFO] [stdout] 
[INFO] [stdout] ---- block_io::tests::best_effort_falls_back_to_ro stdout ----
[INFO] [stdout] 
[INFO] [stdout] thread 'block_io::tests::best_effort_falls_back_to_ro' (46) panicked at src/block_io.rs:351:9:
[INFO] [stdout] read-only file should not report writable
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x5dd8db9d85da - std[1209cfb42f6d497f]::backtrace_rs::backtrace::libunwind::trace
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/std/src/../../backtrace/src/backtrace/libunwind.rs:117:9
[INFO] [stdout]    1:     0x5dd8db9d85da - std[1209cfb42f6d497f]::backtrace_rs::backtrace::trace_unsynchronized::<std[1209cfb42f6d497f]::sys::backtrace::_print_fmt::{closure#1}>
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/std/src/../../backtrace/src/backtrace/mod.rs:66:14
[INFO] [stdout]    2:     0x5dd8db9d85da - std[1209cfb42f6d497f]::sys::backtrace::_print_fmt
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/std/src/sys/backtrace.rs:74:9
[INFO] [stdout]    3:     0x5dd8db9d85da - <<std[1209cfb42f6d497f]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[4a92201981c6b77a]::fmt::Display>::fmt
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/std/src/sys/backtrace.rs:44:26
[INFO] [stdout]    4:     0x5dd8db9ece9a - <core[4a92201981c6b77a]::fmt::rt::Argument>::fmt
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/core/src/fmt/rt.rs:152:76
[INFO] [stdout]    5:     0x5dd8db9ece9a - core[4a92201981c6b77a]::fmt::write
[INFO] [stdout]    6:     0x5dd8db9dce62 - std[1209cfb42f6d497f]::io::default_write_fmt::<alloc[420bb310a727dfd7]::vec::Vec<u8>>
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/std/src/io/mod.rs:621:11
[INFO] [stdout]    7:     0x5dd8db9dce62 - <alloc[420bb310a727dfd7]::vec::Vec<u8> as std[1209cfb42f6d497f]::io::Write>::write_fmt
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/std/src/io/mod.rs:1976:13
[INFO] [stdout]    8:     0x5dd8db9b65cf - <std[1209cfb42f6d497f]::sys::backtrace::BacktraceLock>::print
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/std/src/sys/backtrace.rs:47:9
[INFO] [stdout]    9:     0x5dd8db9b65cf - std[1209cfb42f6d497f]::panicking::default_hook::{closure#0}
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/std/src/panicking.rs:292:27
[INFO] [stdout]   10:     0x5dd8db9cfc69 - std[1209cfb42f6d497f]::panicking::default_hook
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/std/src/panicking.rs:316:9
[INFO] [stdout]   11:     0x5dd8db96c1cc - <alloc[420bb310a727dfd7]::boxed::Box<dyn for<'a, 'b> core[4a92201981c6b77a]::ops::function::Fn<(&'a std[1209cfb42f6d497f]::panic::PanicHookInfo<'b>,), Output = ()> + core[4a92201981c6b77a]::marker::Sync + core[4a92201981c6b77a]::marker::Send> as core[4a92201981c6b77a]::ops::function::Fn<(&std[1209cfb42f6d497f]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/alloc/src/boxed.rs:2285:9
[INFO] [stdout]   12:     0x5dd8db96c1cc - test[59e67a9bc0e119f1]::test_main_inner::<test[59e67a9bc0e119f1]::test_main_static::{closure#0}>::{closure#0}
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/test/src/lib.rs:155:21
[INFO] [stdout]   13:     0x5dd8db9cfe22 - <alloc[420bb310a727dfd7]::boxed::Box<dyn for<'a, 'b> core[4a92201981c6b77a]::ops::function::Fn<(&'a std[1209cfb42f6d497f]::panic::PanicHookInfo<'b>,), Output = ()> + core[4a92201981c6b77a]::marker::Sync + core[4a92201981c6b77a]::marker::Send> as core[4a92201981c6b77a]::ops::function::Fn<(&std[1209cfb42f6d497f]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/alloc/src/boxed.rs:2285:9
[INFO] [stdout]   14:     0x5dd8db9cfe22 - std[1209cfb42f6d497f]::panicking::panic_with_hook
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/std/src/panicking.rs:833:13
[INFO] [stdout]   15:     0x5dd8db9b66ba - std[1209cfb42f6d497f]::panicking::panic_handler::{closure#0}
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/std/src/panicking.rs:691:13
[INFO] [stdout]   16:     0x5dd8db9ab7e9 - std[1209cfb42f6d497f]::sys::backtrace::__rust_end_short_backtrace::<std[1209cfb42f6d497f]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/std/src/sys/backtrace.rs:182:18
[INFO] [stdout]   17:     0x5dd8db9b742d - __rustc[4effac393e9f3da6]::rust_begin_unwind
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/std/src/panicking.rs:689:5
[INFO] [stdout]   18:     0x5dd8db9ed58c - core[4a92201981c6b77a]::panicking::panic_fmt
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/core/src/panicking.rs:80:14
[INFO] [stdout]   19:     0x5dd8db934d1d - fs_ext4[5c53a34dd67368b1]::block_io::tests::best_effort_falls_back_to_ro
[INFO] [stdout]                                at /opt/rustwide/workdir/src/block_io.rs:351:9
[INFO] [stdout]   20:     0x5dd8db918499 - fs_ext4[5c53a34dd67368b1]::block_io::tests::best_effort_falls_back_to_ro::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/workdir/src/block_io.rs:343:38
[INFO] [stdout]   21:     0x5dd8db918499 - <fs_ext4[5c53a34dd67368b1]::block_io::tests::best_effort_falls_back_to_ro::{closure#0} as core[4a92201981c6b77a]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   22:     0x5dd8db95f4ab - <fn() -> core[4a92201981c6b77a]::result::Result<(), alloc[420bb310a727dfd7]::string::String> as core[4a92201981c6b77a]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   23:     0x5dd8db95f4ab - test[59e67a9bc0e119f1]::__rust_begin_short_backtrace::<core[4a92201981c6b77a]::result::Result<(), alloc[420bb310a727dfd7]::string::String>, fn() -> core[4a92201981c6b77a]::result::Result<(), alloc[420bb310a727dfd7]::string::String>>
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/test/src/lib.rs:724:18
[INFO] [stdout]   24:     0x5dd8db96cc9b - test[59e67a9bc0e119f1]::run_test_in_process::{closure#0}
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/test/src/lib.rs:747:74
[INFO] [stdout]   25:     0x5dd8db96cc9b - <core[4a92201981c6b77a]::panic::unwind_safe::AssertUnwindSafe<test[59e67a9bc0e119f1]::run_test_in_process::{closure#0}> as core[4a92201981c6b77a]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   26:     0x5dd8db96cc9b - std[1209cfb42f6d497f]::panicking::catch_unwind::do_call::<core[4a92201981c6b77a]::panic::unwind_safe::AssertUnwindSafe<test[59e67a9bc0e119f1]::run_test_in_process::{closure#0}>, core[4a92201981c6b77a]::result::Result<(), alloc[420bb310a727dfd7]::string::String>>
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/std/src/panicking.rs:581:40
[INFO] [stdout]   27:     0x5dd8db96cc9b - std[1209cfb42f6d497f]::panicking::catch_unwind::<core[4a92201981c6b77a]::result::Result<(), alloc[420bb310a727dfd7]::string::String>, core[4a92201981c6b77a]::panic::unwind_safe::AssertUnwindSafe<test[59e67a9bc0e119f1]::run_test_in_process::{closure#0}>>
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/std/src/panicking.rs:544:19
[INFO] [stdout]   28:     0x5dd8db96cc9b - std[1209cfb42f6d497f]::panic::catch_unwind::<core[4a92201981c6b77a]::panic::unwind_safe::AssertUnwindSafe<test[59e67a9bc0e119f1]::run_test_in_process::{closure#0}>, core[4a92201981c6b77a]::result::Result<(), alloc[420bb310a727dfd7]::string::String>>
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/std/src/panic.rs:359:14
[INFO] [stdout]   29:     0x5dd8db96cc9b - test[59e67a9bc0e119f1]::run_test_in_process
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/test/src/lib.rs:747:27
[INFO] [stdout]   30:     0x5dd8db96cc9b - test[59e67a9bc0e119f1]::run_test::{closure#0}
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/test/src/lib.rs:668:43
[INFO] [stdout]   31:     0x5dd8db9663b4 - test[59e67a9bc0e119f1]::run_test::{closure#1}
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/test/src/lib.rs:698:41
[INFO] [stdout]   32:     0x5dd8db9663b4 - std[1209cfb42f6d497f]::sys::backtrace::__rust_begin_short_backtrace::<test[59e67a9bc0e119f1]::run_test::{closure#1}, ()>
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/std/src/sys/backtrace.rs:166:18
[INFO] [stdout]   33:     0x5dd8db96f8a2 - std[1209cfb42f6d497f]::thread::lifecycle::spawn_unchecked::<test[59e67a9bc0e119f1]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/std/src/thread/lifecycle.rs:70:13
[INFO] [stdout]   34:     0x5dd8db96f8a2 - <core[4a92201981c6b77a]::panic::unwind_safe::AssertUnwindSafe<std[1209cfb42f6d497f]::thread::lifecycle::spawn_unchecked<test[59e67a9bc0e119f1]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}> as core[4a92201981c6b77a]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   35:     0x5dd8db96f8a2 - std[1209cfb42f6d497f]::panicking::catch_unwind::do_call::<core[4a92201981c6b77a]::panic::unwind_safe::AssertUnwindSafe<std[1209cfb42f6d497f]::thread::lifecycle::spawn_unchecked<test[59e67a9bc0e119f1]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/std/src/panicking.rs:581:40
[INFO] [stdout]   36:     0x5dd8db96f8a2 - std[1209cfb42f6d497f]::panicking::catch_unwind::<(), core[4a92201981c6b77a]::panic::unwind_safe::AssertUnwindSafe<std[1209cfb42f6d497f]::thread::lifecycle::spawn_unchecked<test[59e67a9bc0e119f1]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>>
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/std/src/panicking.rs:544:19
[INFO] [stdout]   37:     0x5dd8db96f8a2 - std[1209cfb42f6d497f]::panic::catch_unwind::<core[4a92201981c6b77a]::panic::unwind_safe::AssertUnwindSafe<std[1209cfb42f6d497f]::thread::lifecycle::spawn_unchecked<test[59e67a9bc0e119f1]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/std/src/panic.rs:359:14
[INFO] [stdout]   38:     0x5dd8db96f8a2 - std[1209cfb42f6d497f]::thread::lifecycle::spawn_unchecked::<test[59e67a9bc0e119f1]::run_test::{closure#1}, ()>::{closure#1}
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/std/src/thread/lifecycle.rs:68:26
[INFO] [stdout]   39:     0x5dd8db96f8a2 - <std[1209cfb42f6d497f]::thread::lifecycle::spawn_unchecked<test[59e67a9bc0e119f1]::run_test::{closure#1}, ()>::{closure#1} as core[4a92201981c6b77a]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   40:     0x5dd8db9d7e3f - <alloc[420bb310a727dfd7]::boxed::Box<dyn core[4a92201981c6b77a]::ops::function::FnOnce<(), Output = ()> + core[4a92201981c6b77a]::marker::Send> as core[4a92201981c6b77a]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/alloc/src/boxed.rs:2271:9
[INFO] [stdout]   41:     0x5dd8db9d7e3f - <std[1209cfb42f6d497f]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/std/src/sys/thread/unix.rs:118:17
[INFO] [stdout]   42:     0x722506babaa4 - <unknown>
[INFO] [stdout]   43:     0x722506c38a64 - clone
[INFO] [stdout]   44:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] failures:
[INFO] [stdout]     block_io::tests::best_effort_falls_back_to_ro
[INFO] [stdout] 
[INFO] [stdout] test result: FAILED. 185 passed; 1 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.38s
[INFO] [stdout] 
[INFO] running `Command { std: "docker" "inspect" "8c6cbe9109e2c4a28e43ff1cce8d646812cd6b750109a9b8dff55d15c9bd53be", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "8c6cbe9109e2c4a28e43ff1cce8d646812cd6b750109a9b8dff55d15c9bd53be", kill_on_drop: false }`
[INFO] [stdout] 8c6cbe9109e2c4a28e43ff1cce8d646812cd6b750109a9b8dff55d15c9bd53be
